Dec 01 05:15:01.246 INFO testing razielgn/nes against master#8940af3cea4a9a9deabcaf843b2f8cad9a83fc61 for beta-1.31-5
Dec 01 05:15:01.247 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/beta-1.31-5/worker-4/master#8940af3cea4a9a9deabcaf843b2f8cad9a83fc61:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-4/beta-1.31-5/master#8940af3cea4a9a9deabcaf843b2f8cad9a83fc61:/source:ro,Z" "-v" "/mnt/big/crater/work/local/cargo-home:/cargo-home:ro,Z" "-v" "/mnt/big/crater/work/local/rustup-home:/rustup-home:ro,Z" "-e" "USER_ID=1000" "-e" "SOURCE_DIR=/source" "-e" "USER_ID=1000" "-e" "CMD=cargo +8940af3cea4a9a9deabcaf843b2f8cad9a83fc61-alt build --frozen" "-e" "CARGO_TARGET_DIR=/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "CARGO_HOME=/cargo-home" "-e" "RUSTUP_HOME=/rustup-home" "-m" "1536M" "--network" "none" "crater"`
Dec 01 05:15:02.067 INFO [stdout] cd968e8cab4751503d726c9d0592fd5df1b89ea8c5381e8918a9d0c0395b7dcf
Dec 01 05:15:02.069 INFO running `"docker" "start" "-a" "cd968e8cab4751503d726c9d0592fd5df1b89ea8c5381e8918a9d0c0395b7dcf"`
Dec 01 05:15:03.135 INFO [stderr] usermod: no changes
Dec 01 05:15:03.247 INFO [stderr]    Compiling version_check v0.1.4
Dec 01 05:15:03.247 INFO [stderr]    Compiling cfg-if v0.1.5
Dec 01 05:15:03.247 INFO [stderr]    Compiling libc v0.2.43
Dec 01 05:15:03.247 INFO [stderr]    Compiling ucd-util v0.1.1
Dec 01 05:15:03.247 INFO [stderr]    Compiling regex v1.0.5
Dec 01 05:15:03.247 INFO [stderr]    Compiling quick-error v1.2.2
Dec 01 05:15:03.247 INFO [stderr]    Compiling utf8-ranges v1.0.1
Dec 01 05:15:03.247 INFO [stderr]    Compiling termcolor v1.0.4
Dec 01 05:15:03.707 INFO [stderr]    Compiling log v0.4.5
Dec 01 05:15:04.627 INFO [stderr]    Compiling humantime v1.1.1
Dec 01 05:15:10.315 INFO [stderr]    Compiling atty v0.2.11
Dec 01 05:15:10.315 INFO [stderr]    Compiling regex-syntax v0.6.2
Dec 01 05:15:11.526 INFO [stderr]    Compiling lazy_static v1.1.0
Dec 01 05:15:11.527 INFO [stderr]    Compiling memchr v2.1.0
Dec 01 05:15:13.355 INFO [stderr]    Compiling thread_local v0.3.6
Dec 01 05:15:15.283 INFO [stderr]    Compiling aho-corasick v0.6.8
Dec 01 05:15:15.284 INFO [stderr]    Compiling nom v4.0.0
Dec 01 05:15:57.140 INFO [stderr]    Compiling env_logger v0.5.13
Dec 01 05:15:59.767 INFO [stderr]    Compiling nes v0.1.0 (/source)
Dec 01 05:16:03.033 INFO [stderr]     Finished dev [optimized + debuginfo] target(s) in 59.86s
Dec 01 05:16:03.039 INFO [stderr] su: No module specific data is present
Dec 01 05:16:03.314 INFO running `"docker" "inspect" "cd968e8cab4751503d726c9d0592fd5df1b89ea8c5381e8918a9d0c0395b7dcf"`
Dec 01 05:16:03.410 INFO running `"docker" "rm" "-f" "cd968e8cab4751503d726c9d0592fd5df1b89ea8c5381e8918a9d0c0395b7dcf"`
Dec 01 05:16:03.507 INFO [stdout] cd968e8cab4751503d726c9d0592fd5df1b89ea8c5381e8918a9d0c0395b7dcf
Dec 01 05:16:03.509 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/beta-1.31-5/worker-4/master#8940af3cea4a9a9deabcaf843b2f8cad9a83fc61:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-4/beta-1.31-5/master#8940af3cea4a9a9deabcaf843b2f8cad9a83fc61:/source:ro,Z" "-v" "/mnt/big/crater/work/local/cargo-home:/cargo-home:ro,Z" "-v" "/mnt/big/crater/work/local/rustup-home:/rustup-home:ro,Z" "-e" "USER_ID=1000" "-e" "SOURCE_DIR=/source" "-e" "USER_ID=1000" "-e" "CMD=cargo +8940af3cea4a9a9deabcaf843b2f8cad9a83fc61-alt test --frozen --no-run" "-e" "CARGO_TARGET_DIR=/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "CARGO_HOME=/cargo-home" "-e" "RUSTUP_HOME=/rustup-home" "-m" "1536M" "--network" "none" "crater"`
Dec 01 05:16:03.700 INFO [stdout] 8fd8ae7ea8da22fc808163d8cedd4ca5b85fbb659d4640bb64d4c6f76dc705f6
Dec 01 05:16:03.704 INFO running `"docker" "start" "-a" "8fd8ae7ea8da22fc808163d8cedd4ca5b85fbb659d4640bb64d4c6f76dc705f6"`
Dec 01 05:16:04.117 INFO [stderr] usermod: no changes
Dec 01 05:16:04.155 INFO [stderr]    Compiling nes v0.1.0 (/source)
Dec 01 05:16:08.249 INFO [stderr]     Finished dev [optimized + debuginfo] target(s) in 4.11s
Dec 01 05:16:08.271 INFO [stderr] su: No module specific data is present
Dec 01 05:16:08.794 INFO running `"docker" "inspect" "8fd8ae7ea8da22fc808163d8cedd4ca5b85fbb659d4640bb64d4c6f76dc705f6"`
Dec 01 05:16:09.111 INFO running `"docker" "rm" "-f" "8fd8ae7ea8da22fc808163d8cedd4ca5b85fbb659d4640bb64d4c6f76dc705f6"`
Dec 01 05:16:09.425 INFO [stdout] 8fd8ae7ea8da22fc808163d8cedd4ca5b85fbb659d4640bb64d4c6f76dc705f6
Dec 01 05:16:09.427 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/beta-1.31-5/worker-4/master#8940af3cea4a9a9deabcaf843b2f8cad9a83fc61:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-4/beta-1.31-5/master#8940af3cea4a9a9deabcaf843b2f8cad9a83fc61:/source:ro,Z" "-v" "/mnt/big/crater/work/local/cargo-home:/cargo-home:ro,Z" "-v" "/mnt/big/crater/work/local/rustup-home:/rustup-home:ro,Z" "-e" "USER_ID=1000" "-e" "SOURCE_DIR=/source" "-e" "USER_ID=1000" "-e" "CMD=cargo +8940af3cea4a9a9deabcaf843b2f8cad9a83fc61-alt test --frozen" "-e" "CARGO_TARGET_DIR=/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "CARGO_HOME=/cargo-home" "-e" "RUSTUP_HOME=/rustup-home" "-m" "1536M" "--network" "none" "crater"`
Dec 01 05:16:09.833 INFO [stdout] 794138343effbd40228eb7428fc2c5eb56316c89550917cebb54667445251978
Dec 01 05:16:09.836 INFO running `"docker" "start" "-a" "794138343effbd40228eb7428fc2c5eb56316c89550917cebb54667445251978"`
Dec 01 05:16:11.155 INFO [stderr] usermod: no changes
Dec 01 05:16:11.255 INFO [stderr]     Finished dev [optimized + debuginfo] target(s) in 0.06s
Dec 01 05:16:11.261 INFO [stderr]      Running /target/debug/deps/nes-212537fc65f2c68b
Dec 01 05:16:11.275 INFO [stdout] 
Dec 01 05:16:11.275 INFO [stdout] running 38 tests
Dec 01 05:16:11.275 INFO [stdout] test cpu::test::lda_absolute_x_page_cross ... ok
Dec 01 05:16:11.275 INFO [stdout] test cpu::test::lda_absolute_x ... ok
Dec 01 05:16:11.275 INFO [stdout] test cpu::test::lda_absolute ... ok
Dec 01 05:16:11.275 INFO [stdout] test cpu::test::jmp_indirect ... ok
Dec 01 05:16:11.275 INFO [stdout] test cpu::test::bpl_relative_not_branch ... ok
Dec 01 05:16:11.275 INFO [stdout] test cpu::test::bpl_relative_branch ... ok
Dec 01 05:16:11.275 INFO [stdout] test cpu::test::asl_accumulator ... ok
Dec 01 05:16:11.275 INFO [stdout] test cpu::test::lda_absolute_y ... ok
Dec 01 05:16:11.275 INFO [stdout] test cpu::test::lda_absolute_y_page_cross ... ok
Dec 01 05:16:11.275 INFO [stdout] test cpu::test::lda_indirect_indexed ... ok
Dec 01 05:16:11.275 INFO [stdout] test cpu::test::lda_immediate ... ok
Dec 01 05:16:11.275 INFO [stdout] test cpu::test::lda_indexed_indirect ... ok
Dec 01 05:16:11.275 INFO [stdout] test cpu::test::lda_indirect_indexed_page_crossing ... ok
Dec 01 05:16:11.275 INFO [stdout] test cpu::test::nop_implied ... ok
Dec 01 05:16:11.275 INFO [stdout] test cpu::test::reset ... ok
Dec 01 05:16:11.283 INFO [stdout] test cpu::test::lda_indexed_indirect_page_wraparound ... ok
Dec 01 05:16:11.283 INFO [stdout] test cpu::test::lda_zero_page_x ... ok
Dec 01 05:16:11.283 INFO [stdout] test cpu::test::lda_indirect_indexed_page_wraparound ... ok
Dec 01 05:16:11.283 INFO [stdout] test cpu::test::lda_zero_page ... ok
Dec 01 05:16:11.283 INFO [stdout] test cpu::test::bit_ops_on_p ... ok
Dec 01 05:16:11.283 INFO [stdout] test cpu::test::ldx_zero_page_y ... ok
Dec 01 05:16:11.283 INFO [stdout] test cpu::test::sta_absolute_y_dummy_read ... ok
Dec 01 05:16:11.283 INFO [stdout] test cpu::test::rol_absolute_x_dummy_read ... ok
Dec 01 05:16:11.283 INFO [stdout] test ppu::tests::control::name_table_addr ... ok
Dec 01 05:16:11.283 INFO [stdout] test cpu::test::sta_indirect_indexed_dummy_read ... ok
Dec 01 05:16:11.283 INFO [stdout] test ppu::tests::control::master_slave_select ... ok
Dec 01 05:16:11.283 INFO [stdout] test ppu::tests::control::background_pattern_table_addr ... ok
Dec 01 05:16:11.283 INFO [stdout] test memory::test::ram_wraparound ... ok
Dec 01 05:16:11.283 INFO [stdout] test ppu::tests::control::vram_addr_incr ... ok
Dec 01 05:16:11.283 INFO [stdout] test ppu::tests::mask::grayscale ... ok
Dec 01 05:16:11.283 INFO [stdout] test ppu::tests::oam_write_and_read ... ok
Dec 01 05:16:11.291 INFO [stdout] test ppu::tests::control::nmi_at_next_vblank ... ok
Dec 01 05:16:11.291 INFO [stdout] test ppu::tests::control::sprite_pattern_table_addr ... ok
Dec 01 05:16:11.291 INFO [stdout] test ppu::tests::control::sprite_size ... ok
Dec 01 05:16:11.291 INFO [stdout] test ppu::tests::scroll_and_addr_write_example ... ok
Dec 01 05:16:11.339 INFO [stdout] test ppu::tests::vblank_has_started_and_reading_status_resets_it ... ok
Dec 01 05:16:11.339 INFO [stderr]      Running /target/debug/deps/blargg-95a8ad65d0d4b2d9
Dec 01 05:16:11.339 INFO [stdout] test ppu::tests::vblank_has_ended ... ok
Dec 01 05:16:11.339 INFO [stdout] test ppu::tests::odd_frames_are_shorter_by_one_cycle ... ok
Dec 01 05:16:11.339 INFO [stdout] 
Dec 01 05:16:11.339 INFO [stdout] test result: ok. 38 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 01 05:16:11.339 INFO [stdout] 
Dec 01 05:16:11.339 INFO [stdout] 
Dec 01 05:16:11.339 INFO [stdout] running 33 tests
Dec 01 05:16:11.339 INFO [stdout] test cpu_interrupts_v2::branch_delays_irq ... ignored
Dec 01 05:16:11.339 INFO [stdout] test cpu_interrupts_v2::cli_latency ... ignored
Dec 01 05:16:11.343 INFO [stdout] test cpu_interrupts_v2::irq_and_dma ... ignored
Dec 01 05:16:11.343 INFO [stdout] test cpu_interrupts_v2::nmi_and_brk ... ignored
Dec 01 05:16:11.363 INFO [stdout] test cpu_interrupts_v2::nmi_and_irq ... ignored
Dec 01 05:16:11.363 INFO [stdout] test cpu_reset::registers ... ignored
Dec 01 05:16:11.363 INFO [stdout] test instr_misc::dummy_reads_apu ... ignored
Dec 01 05:16:11.755 INFO [stdout] test instr_misc::abs_x_wrap ... ok
Dec 01 05:16:11.783 INFO [stdout] test instr_misc::branch_wrap ... ok
Dec 01 05:16:12.526 INFO [stdout] test instr_test_v5::basics ... ok
Dec 01 05:16:12.779 INFO [stdout] test instr_misc::dummy_reads ... ok
Dec 01 05:16:12.927 INFO [stdout] test instr_test_v5::brk ... ok
Dec 01 05:16:13.447 INFO [stdout] test instr_test_v5::branches ... ok
Dec 01 05:16:15.093 INFO [stdout] test instr_test_v5::implied ... ok
Dec 01 05:16:15.473 INFO [stdout] test instr_test_v5::jmp_jsr ... ok
Dec 01 05:16:16.166 INFO [stdout] test instr_test_v5::absolute ... ok
Dec 01 05:16:16.191 INFO [stdout] test instr_test_v5::immediate ... ok
Dec 01 05:16:16.660 INFO [stdout] test instr_test_v5::rti ... ok
Dec 01 05:16:17.135 INFO [stdout] test instr_test_v5::rts ... ok
Dec 01 05:16:17.136 INFO [stdout] test instr_test_v5::stack ... ignored
Dec 01 05:16:17.191 INFO [stdout] test instr_test_v5::special ... ok
Dec 01 05:16:17.243 INFO [stdout] test cpu_reset::ram_after_reset ... ok
Dec 01 05:16:17.243 INFO [stdout] test instr_timing::branch_timing ... ignored
Dec 01 05:16:17.243 INFO [stdout] test instr_timing::instr_timing ... ignored
Dec 01 05:16:18.347 INFO [stdout] test oam_read ... ok
Dec 01 05:16:19.547 INFO [stdout] test instr_test_v5::ind_x ... ok
Dec 01 05:16:21.063 INFO [stdout] test instr_test_v5::ind_y ... ok
Dec 01 05:16:22.275 INFO [stdout] test instr_test_v5::zero_page ... ok
Dec 01 05:16:22.826 INFO [stdout] test instr_test_v5::abs_xy ... ok
Dec 01 05:16:25.035 INFO [stdout] test instr_test_v5::zp_xy ... ok
Dec 01 05:16:40.235 INFO [stdout] test instr_test_v5::all_instrs ... ok
Dec 01 05:16:42.564 INFO [stdout] test instr_test_v5::official_only ... ok
Dec 01 05:16:54.382 INFO [stderr]      Running /target/debug/deps/nestest-ac434fc2b0266fce
Dec 01 05:16:54.383 INFO [stdout] test oam_stress ... ok
Dec 01 05:16:54.384 INFO [stdout] 
Dec 01 05:16:54.384 INFO [stdout] test result: ok. 23 passed; 0 failed; 10 ignored; 0 measured; 0 filtered out
Dec 01 05:16:54.384 INFO [stdout] 
Dec 01 05:16:54.385 INFO [stdout] 
Dec 01 05:16:54.385 INFO [stdout] running 1 test
Dec 01 05:16:54.579 INFO [stdout] test nestest ... ok
Dec 01 05:16:54.580 INFO [stdout] 
Dec 01 05:16:54.580 INFO [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 01 05:16:54.580 INFO [stdout] 
Dec 01 05:16:54.583 INFO [stderr]      Running /target/debug/deps/rom-00b206fd94a75d4e
Dec 01 05:16:54.587 INFO [stdout] 
Dec 01 05:16:54.587 INFO [stdout] running 1 test
Dec 01 05:16:54.587 INFO [stdout] test nestest_decode ... ok
Dec 01 05:16:54.587 INFO [stdout] 
Dec 01 05:16:54.587 INFO [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 01 05:16:54.587 INFO [stdout] 
Dec 01 05:16:54.599 INFO [stderr] su: No module specific data is present
Dec 01 05:16:55.794 INFO running `"docker" "inspect" "794138343effbd40228eb7428fc2c5eb56316c89550917cebb54667445251978"`
Dec 01 05:16:56.135 INFO running `"docker" "rm" "-f" "794138343effbd40228eb7428fc2c5eb56316c89550917cebb54667445251978"`
Dec 01 05:16:56.463 INFO [stdout] 794138343effbd40228eb7428fc2c5eb56316c89550917cebb54667445251978
